Appendix: Exemplary Trace File 



Microsoft (R) windows Script Host Version 5.6 

Copyright (C) Microsoft Corporation 1996-2001. All rights reserved. 



Request n.l: /datetime.asp {00000000-0000-0000-0200-0060000000fe} 2003-11- 
10 2:47:16 

iiSGeneral : general_request_start - us starts processing a new request 
Siteld: 1 

AppPoolld: DefaultAppPool 
connid: 1073741825 
Rawconnld: 0 

RequestURL : /dateti me . asp 

Requestverb: GET 

Timestamp: 02:47:16.936.270800 

HSFilter: Fl LTE RESTART - w3 filter starts 
FilterName: 

C : \wlNNT\Mi crosof t . NET\Framework\vl . 1 . 4322\aspnet_f i 1 ter . dl 1 
Timestamp: 02:47:16.936.270800 

IISFilter: FILTER_PREPROC_HEADERS_START - Filter Starts PREPROC_HEADERS 
notification 

Timestamp: 02:47:16.936.270800 

IISFilter: filter_preproc_headers_end - Filter ends preproc_headers 
notifi cation 

Timestamp: 02:47:16.936.270800 

IISFilter: filter_END - W3 filter ends 
Timestamp: 02:47:16.936.270800 

"ilSCache: URL_CACHE^ACCESS_START - IIS starts accessing URL cache to get 
metadata 

RequestURL: /datetime.asp 
Timestamp: 02:47:16.936.270800 

IiSGeneral : general_get_url_metadata - us gets url metadata 
Physi cal Path : c : \i netpub\wwwroot\dateti me . asp 
AccessPerms: Read+script 
Timestamp: 02:47:17.066.464500 

nscache: URL_CACHE_ACCESS_END - IIS ends accessing URL cache 
Physi cal Path : c : \i netpub\wwwroot\dateti me . asp 
Errorcode: 0x00000000 
URLinfoFromCache : 0 
URLinfoAddedToCache: 0 
Timestamp: 02:47:17.066.464500 

IISFilter: filter_start - w3 filter starts 
FilterName: 

C : \wiNNT\Mi crosof t . NET\Framewor k\vl . 1 . 4322\aspnet_f i 1 ter . dll 
Timestamp: 02:47:17.066.464500 

IISFilter: filter_url_map_START - IIS starts URL_MAP notification 
AccessPerms: 0x00000201 
Matchingpath: 18 
MatchingURL: 0 

Scri ptMapEnt ry : c : \WINNT\system32\i nets rv\asp . dl 1 
OrigURL: /datetime.asp 
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Ori gPath : c : \i netpub\wwwroot\dateti me . asp 
Timestamp: 02:47:17.066.464500 

iiSFilter: filter_url_map_end - us ends url_map notification 
AccessPerms: 0x00000201 
Final URL: /datetime.asp 
Fi nal Path : c : \i netpub\wwwroot\dateti me . asp 
Matchingpath: 18 
MatchingURL: 0 

Scri ptMapEnt ry : C : \wlNNT\system32\i nets rv\asp . dl 1 
Timestamp: 02:47:17.066.464500 

IiSFilter: FILTER^ end - w3 filter ends 
Timestamp: 02:47:17.066.464500 

USAuthenti cation: AUTH_START - IIS authentication starts 
AuthTypeSupported: Anonymous+NT 
Timestamp: 02:47:17.066.464500 

USAuthenti cation: auth_request_jvuth_TYPE - Authentication type in the 
request 

RequestAuthType: Anonymous 
Timestamp: 02:47:17.066.464500 

USAuthenti cation: AUTH_SUCCEEDED - IIS authentication succeeds 
NTLMUsed : 0 
AuthType: Anonymous 
RemoteuserName: 
AuthuserName: 

TokenimpersonationLevel : 0x00000002 
Timestamp: 02:47:17.066.464500 

USAuthenti cation: auth_end - us authentication ends 
Timestamp: 02:47:17.066.464500 

iiSCache: FILE_CACHE^ACCESS_START - us starts accessing file cache 
Fi 1 eName : c : \i netpub\wwwroot\dateti me . asp 
UserName: IUSR_ericde-vs-SP1 
DomainName: ERICDE-VS-SP1 
Timestamp: 02:47:17.086.494300 

nscache: file_cache_access_end - us ends accessing file cache 
Error Code: 0x00000000 
Successful : 1 
FileFromCache: 0 
FileAddedToCache: 0 
FileDi rmoned: 1 
LastModCheckEr rorlgnored : 0 
Timestamp: 02:47:17.156.598600 

IISISAPI: ISAPI_START - IIS starts processing an ISAPI Request 
Timestamp: 02:47:17.156.598600 

HSGeneral: GENERAL_isapi_handler - us processes an in-proc isapi 
request 

Timestamp: 02:47:17.156.598600 

W3isapi: CALL_ISAPI_EXTENSI0N - Call isapi Extension 
Dl 1 Name : c : \WlNNT\system32\i nets rv\asp . dl 1 
Timestamp: 02:47:17.937.760800 

AspReq: ASP_START_REQUEST - Start Request 

Physi cal Path : c : \i netpub\wwwroot\dateti me . asp 
Timestamp: 02:47:17.937.760800 



Iee@hayes 



37 



MSl-1906US.Pat.App 



AspReq: ASP_FlRST_REQUEST_FOR_ASP - First request 
Timestamp: 02:47:17.937.760800 

IlSCache: URL_CACHE^ACCESS_START - IIS starts accessing URL cache to get 
metadata 

RequestURL: / 

Timestamp: 02:47:18.648.818700 

iiSGeneral: general_get_url_metadata - us gets url metadata 
Physical Path: c:\inetpub\wwwroot\ 
AccessPerms: Read+Script 
Timestamp: 02:47:18.678.863400 

IlSCache: url_cache^access_end - us ends accessing url cache 
Physical Path: c:\inetpub\wwwroot\ 
ErrorCode: 0x00000000 
URLlnf oFromCache : 0 
URLlnfoAddedToCache: 0 
Timestamp: 02:47:18.678.863400 

AspReq: ASP_FIRST_REQUEST_FOR_j\PPLICATION - First request for 
application 

Appl i cati onPath : c : \INETPUB\wwwroot\ 
Timestamp: 02:47:18.678.863400 

IlSCache: url_CACHE^access_START - us starts accessing url cache to get 
metadata 

RequestURL: / 

Timestamp: 02:47:18.718.923000 

IiSGeneral: G E N E RA L_G ET_u R L_M ETADATA - iis gets url metadata 
Physical Path: c:\inetpub\wwwroot\ 
AccessPerms: Read+Script 
Timestamp: 02:47:18.718.923000 

nscache: url_cache_access_end - us ends accessing url cache 
Physical Path: c:\inetpub\wwwroot\ 
ErrorCode: 0x00000000 
URLlnf oFromCache: 0 
URLlnfoAddedToCache: 1 
Timestamp: 02:47:18.718.923000 

AspReq: ASP_START_GLOBAl ASA_CHECK - Start Global .ASA check 

Gl Obal ASAPath : C : \lNETPUB\WWWROOT\GLOBAL . ASA 
Timestamp: 02:47:18.789.027300 

AspReq: ASP_GLOBAL^ASA^DOES_NOT_EXlST - Global .ASA does not exist 
Timestamp: 02:47:18.789.027300 

AspReq: asp_end_global^\sa_check - End Global .ASA check 
Timestamp: 02:47:18.789.027300 

AspReq: ASP_NEW_SESSlON_CREATED - New session Created 
Sessi onld : FLILJ EEDHAGHEDOLFANHMGDF 
Timestamp: 02:47:18.789.027300 

AspReq: AS P_QU EUE_REQUEST - Queue Request 
Timestamp: 02:47:18.819.072000 

AspReq: AS P_D EQU EU E_R EQU EST - Dequeue Request 
Timestamp: 02:47:18.939.250800 

AspReq: ASP_START_CACHE^ACCESS - Check cache Begin 

Fi 1 ePath : C : \lNETPUB\WWWROOT\DATETIME . ASP 
Timestamp: 02:47:18.939.250800 
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AspReq: asp_START_compile - Begin compilation 

Fi 1 ePath : C : \lNETPUB\WWWROOT\DATETIME . ASP 
Timestamp: 02:47:18.939.250800 

AspReq: ASP_READ_FILE - Read File 

Fi 1 ePath : c : \inetpub\wwwroot\datetime . asp 
Timestamp: 02:47:18.939.250800 

AspReq: ASP_END_COMPILE - End Compilation 
Timestamp: 02:47:19.560.174600 

AspReq: asp_end_cache^access - Check cache End 
ErrorCode: 0x00000000 
ACCesSResult: SERVED_CACHE_MISS 
Timestamp: 02:47:19.560.174600 

AspReq: ASP_START_SCRIPT_EXECUTI0N - Start Script Execution 
Timestamp: 02:47:19.570.189500 

AspReq: ASP_SCRIPT_TRACE_START - ASP Script Trace Info 

Fi 1 ePath : C : \lNETPUB\WWWROOT\DATETIME . ASP 
Timestamp: 02:47:19.570.189500 

AspReq: asp_script_trace_com_call_start - asp script Trace object 
invocation info 

Fi 1 ePath : c : \inetpub\wwwroot\datetime . asp 
LineNumber: 3 

Currentstatement : Set obj Browser = 
Server. CreateObject("MSWC.BrowserType") 
Si zeof Statement : 56 
Timestamp: 02:47:19.570.189500 

AspReg: asp_script_trace_com_call_end - asp script Trace object 
Invocation info 

Fi 1 ePath : C : \lNETPUB\WWWROOT\DATETIME . ASP 
LineNumber: 3 

Currentstatement: Set obj Browser = 
serve r . c r eateob j ect ( "MSWC . B rowse rrype " ) 
Si zeof Statement : 56 
Timestamp: 02:47:19.930.725900 

AspReq: ASP_SCRlPT_TRACE_COM_CALL_START - ASP Script Trace object 
invocation Info 

Fi 1 ePath : C : \lNETPUB\WWWROOT\DATETIME . ASP 
LineNumber: 5 

Currentstatement : Response . wri te (obj Browser . Browser) 
Si zeof Statement : 34 
Timestamp: 02:47:19.930.725900 

AspReq: ASP_SCRlPT_TRACE_COM_CALL_END - ASP Script Trace Object 
invocation info 

Fi 1 ePath : C : \lNETPUB\WWWROOT\DATETIME . ASP 
LineNumber: 5 

Currentstatement : Response .Wri te(obj Browser . Browser) 
Si zeof Statement : 34 
Timestamp: 02:47:19.930.725900 

AspReq: asp_SCRIPT_trace_COM_CALL_START - ASP Script Trace Object 
invocation info 

Fi 1 ePath : C : \lNETPUB\WWWROOT\DATETIME . ASP 

LineNumber: 6 

Currentstatement : Response . wri te (obj Browser . versi on) 
Si zeof Statement : 34 
Timestamp: 02:47:19.930.725900 
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AspReg: asp_SCRIPT_trace_COM_call_end - asp Script Trace Object 
invocation info 

Fi 1 epath : c : \inetpub\wwwroot\datetime . asp 
LineNumber: 6 

Currentstatement : Response. write(obj Browser. version) 
Si zeof Statement : 34 
Timestamp: 02:47:19.930.725900 

AspReq: ASP_SCRIPT_TRACE_END - ASP Script Trace info 
Fi 1 ePath : C : \lNETPUB\WWWROOT\DATETIME . ASP 
Timestamp: 02:47:19.940.740800 

AspReq: ASP_END_SCRlPT_EXECUTlON - End Script Execution 
Timestamp: 02:47:19.940.740800 

nscache: httpsys_cacheable - us decides if the request is http.sys 
cacheable 

HttpsysCacheabl e : 1 
Reason * OK 

Timestamp: 02:47:19.940.740800 

AspReq: ASP_D0NE_PR0CESSING - ASP Done Processing 
Timestamp: 02:47:19.970.785500 

W3lsapi : N0TIFY_ISAPI_C0MPLETI0N - Notify isapi of Completion 
Timestamp: 02:47:19.970.785500 

AspReq: ASP_END_REQUEST - End Request 
Timestamp: 02:47:19.970.785500 

W3isapi: isapi_extension_done - isapi Extension Done 
Timestamp: 02:47:19.970.785500 

IISISAPI: ISAPI_END - IIS ends processing an ISAPI Request 
Timestamp: 02:47:19.970.785500 

iiSGeneral: general_request_end - us ends processing a request 
Httpstatus: 200 
HttpSubStatus: 0 
BytesSent: 354 
BytesReceived: 263 
Timestamp: 02:47:20.000.830200 

Total time: 4000 msecs 
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